← Index
NYTProf Performance Profile   « line view »
For starman worker -M FindBin --max-requests 50 --workers 2 --user=kohadev-koha --group kohadev-koha --pid /var/run/koha/kohadev/plack.pid --daemonize --access-log /var/log/koha/kohadev/plack.log --error-log /var/log/koha/kohadev/plack-error.log -E deployment --socket /var/run/koha/kohadev/plack.sock /etc/koha/sites/kohadev/plack.psgi
  Run on Fri Jan 8 13:50:58 2016
Reported on Fri Jan 8 13:51:26 2016

Filename(eval 161)[/usr/share/perl5/Text/MicroTemplate.pm:389]
StatementsExecuted 0 statements in 0s
Subroutines
Calls P F Exclusive
Time
Inclusive
Time
Subroutine
300011371ms13.4sKoha::Objects::::find Koha::Objects::find
300011313ms313msDBI::st::::execute DBI::st::execute (xsub)
2416785281ms318msList::Util::::first List::Util::first (xsub)
81645107244ms244msnext::::method next::method
652885189ms1.09sTry::Tiny::::try Try::Tiny::try
300011146ms146msKoha::Object::::AUTOLOAD Koha::Object::AUTOLOAD
30001160.3ms60.4msDBD::_::db::::prepare_cached DBD::_::db::prepare_cached
21321321250.6ms166msbase::::import base::import (recurses: max depth 3, inclusive time 100ms)
11463231049.0ms49.0msUNIVERSAL::::can UNIVERSAL::can (xsub)
30001129.8ms90.2msDBI::db::::prepare_cached DBI::db::prepare_cached (xsub)
60002129.1ms29.1msDBI::st::::fetchrow_array DBI::st::fetchrow_array (xsub)
277095426.6ms26.6msScalar::Util::::reftype Scalar::Util::reftype (xsub)
60575426.5ms26.5msUNIVERSAL::::isa UNIVERSAL::isa (xsub)
457310422.3ms22.3msSub::Name::::subname Sub::Name::subname (xsub)
33546422.1ms22.1msTry::Tiny::::catch Try::Tiny::catch
5201119.0ms19.7msFile::Spec::Unix::::abs2rel File::Spec::Unix::abs2rel
31117.7ms43.8msFile::Find::::find File::Find::find
245276615.6ms15.6msScalar::Util::::blessed Scalar::Util::blessed (xsub)
30001111.4ms11.4msDBI::st::::bind_param DBI::st::bind_param (xsub)
33693310.6ms10.6msmro::::get_linear_isa mro::get_linear_isa (xsub)
91771489.59ms9.59msScalar::Util::::weaken Scalar::Util::weaken (xsub)
11862492459.57ms9.57msstrict::::import strict::import
11822452419.06ms9.06mswarnings::::import warnings::import
95529185.34ms5.34msstrict::::unimport strict::unimport
5629295.27ms6.74msB::Hooks::EndOfScope::XS::::__ANON__ B::Hooks::EndOfScope::XS::__ANON__
1115.06ms16.5msDBI::::connect DBI::connect
3000115.00ms5.00msDBI::st::::finish DBI::st::finish (xsub)
1114.03ms4.03msDynaLoader::::bootstrap DynaLoader::bootstrap
1113.68ms3.75msPlack::Sandbox::_2fetc_2fkoha_2fsites_2fkohadev_2fplack_2epsgi::::__ANON__Plack::Sandbox::_2fetc_2fkoha_2fsites_2fkohadev_2fplack_2epsgi::__ANON__
9898493.38ms3.38msExporter::::import Exporter::import
119113.34ms3.34msXML::SAX::Expat::::_handle_start XML::SAX::Expat::_handle_start
2828283.31ms4.04msnamespace::clean::::import namespace::clean::import
4332.73ms2.90msConfig::::FETCH Config::FETCH
1473811.85ms2.10msPackage::Stash::XS::::namespace Package::Stash::XS::namespace (xsub)
382111.82ms1.82msXML::SAX::Expat::::_handle_char XML::SAX::Expat::_handle_char
3321.68ms1.68msCGI::::AUTOLOAD CGI::AUTOLOAD
2221.54ms1.54msXSLoader::::load XSLoader::load
555581.19ms1.19msconstant::::import constant::import
119111.11ms1.11msXML::SAX::Expat::::_handle_end XML::SAX::Expat::_handle_end
221858µs2.02msModule::Runtime::::require_module Module::Runtime::require_module
174174174735µs735µsutf8::::import utf8::import
89152721µs721µsFile::Spec::Unix::::canonpath File::Spec::Unix::canonpath (xsub)
51711583µs583µsFile::Spec::Unix::::splitdir File::Spec::Unix::splitdir
393932502µs502µswarnings::::unimport warnings::unimport
52011385µs385µsFile::Spec::Unix::::curdir File::Spec::Unix::curdir
776314µs314µsvars::::import vars::import
11041255µs255µsPackage::Stash::XS::::name Package::Stash::XS::name (xsub)
111199µs199µsClass::Accessor::::mk_accessors Class::Accessor::mk_accessors
444173µs173µsoverload::::import overload::import
322155µs155µsCGI::Util::::rearrange CGI::Util::rearrange
111141µs141µsDBI::db::::do DBI::db::do (xsub)
111129µs129µsModern::Perl::::import Modern::Perl::import
1453117µs117µsversion::vxs::::_VERSION version::vxs::_VERSION (xsub)
551104µs104µsDBD::_::common::::install_method DBD::_::common::install_method
88891µs91µsList::Util::::import List::Util::import
11188µs88µsSub::Exporter::Progressive::::import Sub::Exporter::Progressive::import
11187µs87µsFile::pushd::::DESTROY File::pushd::DESTROY
22282µs82µsCGI::::import CGI::import
11177µs77µsPlack::Component::::to_app_auto Plack::Component::to_app_auto
262175µs89µsFile::Spec::Unix::::catdir File::Spec::Unix::catdir (xsub)
161170µs70µsXML::SAX::Expat::::_handle_comment XML::SAX::Expat::_handle_comment
11152µs52µsDBI::::_new_drh DBI::_new_drh
11150µs50µsFile::pushd::::pushd File::pushd::pushd
22246µs46µsSelectSaver::::DESTROY SelectSaver::DESTROY
21142µs42µsIO::Handle::::read IO::Handle::read
33341µs41µsModule::Runtime::::import Module::Runtime::import
117441µs41µsDBI::common::::STORE DBI::common::STORE (xsub)
33340µs40µsConfig::::import Config::import
32139µs39µsModule::Runtime::::module_notional_filename Module::Runtime::module_notional_filename
11134µs34µsModule::Runtime::::use_package_optimistically Module::Runtime::use_package_optimistically
66633µs33µsmro::::import mro::import
54432µs32µsCGI::::self_or_default CGI::self_or_default
11132µs32µsDBI::::_new_sth DBI::_new_sth
54229µs29µsDBI::common::::FETCH DBI::common::FETCH (xsub)
22225µs25µsSub::Exporter::Progressive::::__ANON__ Sub::Exporter::Progressive::__ANON__
22225µs25µsModule::Runtime::::use_module Module::Runtime::use_module
11124µs24µsDBI::::_new_dbh DBI::_new_dbh
11124µs24µsXML::Parser::Expat::::DESTROY XML::Parser::Expat::DESTROY
11122µs22µsC4::Context::::config C4::Context::config
21119µs19µsCGI::::param CGI::param
11118µs1.71msDBI::db::::get_info DBI::db::get_info (xsub)
11118µs18µsCGI::::initialize_globals CGI::initialize_globals
11117µs17µsCGI::::charset CGI::charset
52214µs14µsmro::::set_mro mro::set_mro (xsub)
22212µs12µsC4::Context::::import C4::Context::import
2218µs8µsDBI::common::::func DBI::common::func (xsub)
3118µs8µsoverload::::AddrRef overload::AddrRef
1118µs8µsbytes::::import bytes::import
1117µs7µsClass::Accessor::::import Class::Accessor::import
1111µs1µsInternals::::SvREADONLY Internals::SvREADONLY (xsub)
1111µs1µsScalar::Util::::refaddr Scalar::Util::refaddr (xsub)
0000s0sPlack::Component::::__ANON__[:3] Plack::Component::__ANON__[:3]
0000s0sPlack::Component::::__ANON__[:72] Plack::Component::__ANON__[:72]
0000s0sPlack::Component::::__ANON__[:74] Plack::Component::__ANON__[:74]
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments
Time
on line
Calls Time
in subs
Code
1package Plack::Component;
2sub {
3 local $SIG{__WARN__} = sub { print STDERR $_mt->_error(shift, 4, $_from) };
4 Text::MicroTemplate::encoded_string((
5 sub { my $_MT = ''; local $Plack::Component::_MTREF = \$_MT; my $_MT_T = '';my $stash = $_[0];
6;
7
- -
11$_MT .="\<script\ type\=\"text\/javascript\"\ charset\=\"utf\-8\"\>\n\ \ \ \ \/\/\ When\ jQuery\ is\ sourced\,\ it\'s\ going\ to\ overwrite\ whatever\ might\ be\ in\ the\n\ \ \ \ \/\/\ \'\$\'\ variable\,\ so\ store\ a\ reference\ of\ it\ in\ a\ temporary\ variable\.\.\.\n\ \ \ \ var\ _\$\ \=\ window\.\$\;\n\ \ \ \ if\ \(typeof\ jQuery\ \=\=\ \'undefined\'\)\ \{\n\ \ \ \ \ \ \ \ var\ jquery_url\ \=\ \'";$_MT_T = $stash->{BASE_URL} ;$_MT .= ref $_MT_T eq 'Text::MicroTemplate::EncodedString' ? $$_MT_T : do{ $_MT_T =~ s/([&><"'])/$Text::MicroTemplate::_escape_table{$1}/ge; $_MT_T; } ; $_MT_T = '';
12
- -
15$_MT .="\/debug_toolbar\/jquery\.js\'\;\n\ \ \ \ \ \ \ \ document\.write\(unescape\(\'\%3Cscript\ src\=\"\'\ \+\ jquery_url\ \+\ \'\"\ type\=\"text\/javascript\"\%3E\%3C\/script\%3E\'\)\)\;\n\ \ \ \ \}\n\<\/script\>\n\<script\ type\=\"text\/javascript\"\ src\=\"";$_MT_T = $stash->{BASE_URL} ;$_MT .= ref $_MT_T eq 'Text::MicroTemplate::EncodedString' ? $$_MT_T : do{ $_MT_T =~ s/([&><"'])/$Text::MicroTemplate::_escape_table{$1}/ge; $_MT_T; } ; $_MT_T = '';
16
- -
21$_MT .="\/debug_toolbar\/toolbar\.min\.js\"\>\<\/script\>\n\<script\ type\=\"text\/javascript\"\ charset\=\"utf\-8\"\>\n\ \ \ \ \/\/\ Now\ that\ jQuery\ is\ done\ loading\,\ put\ the\ \'\$\'\ variable\ back\ to\ what\ it\ was\.\.\.\n\ \ \ \ var\ \$\ \=\ _\$\;\n\<\/script\>\n\<style\ type\=\"text\/css\"\>\n\ \ \ \ \@import\ url\(";$_MT_T = $stash->{BASE_URL} ;$_MT .= ref $_MT_T eq 'Text::MicroTemplate::EncodedString' ? $$_MT_T : do{ $_MT_T =~ s/([&><"'])/$Text::MicroTemplate::_escape_table{$1}/ge; $_MT_T; } ; $_MT_T = '';
22
- -
25$_MT .="\/debug_toolbar\/toolbar\.min\.css\)\;\n\<\/style\>\n\<div\ id\=\"plDebug\"\>\n\ \ \ \ \<div\ style\=\"display\:none\;\"\ id\=\"plDebugToolbar\"\>\n\ \ \ \ \ \ \ \ \<ul\ id\=\"plDebugPanelList\"\>\n";
26if ($stash->{panels}) {
27;$_MT .="\ \ \ \ \ \ \ \ \ \ \ \ \<li\>\<a\ id\=\"plHideToolBarButton\"\ href\=\"\#\"\ title\=\"Hide\ Toolbar\"\>Hide\ \&raquo\;\<\/a\>\<\/li\>\n";
28} else {
29;$_MT .="\ \ \ \ \ \ \ \ \ \ \ \ \<li\ id\=\"plDebugButton\"\>DEBUG\<\/li\>\n";
30}
31for my $panel (reverse @{$stash->{panels}}) {
32;$_MT .="\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<li\>\n";
33if ($panel->content) {
34;$_MT .="\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<a\ href\=\"";$_MT_T = $panel->url ;$_MT .= ref $_MT_T eq 'Text::MicroTemplate::EncodedString' ? $$_MT_T : do{ $_MT_T =~ s/([&><"'])/$Text::MicroTemplate::_escape_table{$1}/ge; $_MT_T; } ; $_MT_T = '';$_MT .="\"\ title\=\"";$_MT_T = $panel->title ;$_MT .= ref $_MT_T eq 'Text::MicroTemplate::EncodedString' ? $$_MT_T : do{ $_MT_T =~ s/([&><"'])/$Text::MicroTemplate::_escape_table{$1}/ge; $_MT_T; } ; $_MT_T = '';$_MT .="\"\ class\=\"";$_MT_T = $panel->dom_id ;$_MT .= ref $_MT_T eq 'Text::MicroTemplate::EncodedString' ? $$_MT_T : do{ $_MT_T =~ s/([&><"'])/$Text::MicroTemplate::_escape_table{$1}/ge; $_MT_T; } ; $_MT_T = '';$_MT .="\"\>\n";
35} else {
36;$_MT .="\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<div\ class\=\"contentless\"\>\n";
37}
38;$_MT .="\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ ";$_MT_T = $panel->nav_title ;$_MT .= ref $_MT_T eq 'Text::MicroTemplate::EncodedString' ? $$_MT_T : do{ $_MT_T =~ s/([&><"'])/$Text::MicroTemplate::_escape_table{$1}/ge; $_MT_T; } ; $_MT_T = '';$_MT .="\n";
39if ($panel->nav_subtitle) {
40;$_MT .="\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<br\>\<small\>";$_MT_T = $panel->nav_subtitle ;$_MT .= ref $_MT_T eq 'Text::MicroTemplate::EncodedString' ? $$_MT_T : do{ $_MT_T =~ s/([&><"'])/$Text::MicroTemplate::_escape_table{$1}/ge; $_MT_T; } ; $_MT_T = '';$_MT .="\<\/small\>\n";
41}
42if ($panel->content) {
43;$_MT .="\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<\/a\>\n";
44} else {
45;$_MT .="\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<\/div\>\n";
46}
47;$_MT .="\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<\/li\>\n";
48} # end for
49;
50
- -
53$_MT .="\ \ \ \ \ \ \ \ \<\/ul\>\n\ \ \ \ \<\/div\>\n\ \ \ \ \<div\ style\=\"display\:none\;\"\ id\=\"plDebugToolbarHandle\"\>\n\ \ \ \ \ \ \ \ \<a\ title\=\"Show\ Toolbar\"\ id\=\"plShowToolBarButton\"\ href\=\"\#\"\>\&laquo\;\<\/a\>\n\ \ \ \ \<\/div\>\n";
54for my $panel (reverse @{$stash->{panels}}) {
55if ($panel->content) {
56;$_MT .="\ \ \ \ \ \ \ \ \ \ \ \ \<div\ id\=\"";$_MT_T = $panel->dom_id ;$_MT .= ref $_MT_T eq 'Text::MicroTemplate::EncodedString' ? $$_MT_T : do{ $_MT_T =~ s/([&><"'])/$Text::MicroTemplate::_escape_table{$1}/ge; $_MT_T; } ; $_MT_T = '';
57
58
59$_MT .="\"\ class\=\"panelContent\"\>\n\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<div\ class\=\"plDebugPanelTitle\"\>\n\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<a\ href\=\"\"\ class\=\"plDebugClose\"\>Close\<\/a\>\n\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<h3\>";$_MT_T = $panel->title ;$_MT .= ref $_MT_T eq 'Text::MicroTemplate::EncodedString' ? $$_MT_T : do{ $_MT_T =~ s/([&><"'])/$Text::MicroTemplate::_escape_table{$1}/ge; $_MT_T; } ; $_MT_T = '';
60
61
62$_MT .="\<\/h3\>\n\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<\/div\>\n\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<div\ class\=\"plDebugPanelContent\"\>\n\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<div\ class\=\"scroll\"\>\n";
63my $content = ref $panel->content eq 'CODE' ? $panel->content->() : $panel->content;
64$content = Encode::encode('latin1', $content, Encode::FB_XMLCREF);
65;$_MT .="\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ ";$_MT_T = Text::MicroTemplate::encoded_string($content) ;$_MT .= ref $_MT_T eq 'Text::MicroTemplate::EncodedString' ? $$_MT_T : do{ $_MT_T =~ s/([&><"'])/$Text::MicroTemplate::_escape_table{$1}/ge; $_MT_T; } ; $_MT_T = '';
66
67
68$_MT .="\n\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<\/div\>\n\ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \ \<\/div\>\n\ \ \ \ \ \ \ \ \ \ \ \ \<\/div\>\n";
69}
70} # end for
71;
72$_MT .="\ \ \ \ \<div\ id\=\"plDebugWindow\"\ class\=\"panelContent\"\>\<\/div\>\n\<\/div\>\n";return $_MT; }
73 )->(@_));
74}
75
76;
 
# spent 29µs within DBI::common::FETCH which was called 5 times, avg 6µs/call: # 2 times (9µs+0s) by DBIx::Class::Storage::DBI::_determine_driver at line 1255 of DBIx/Class/Storage/DBI.pm, avg 4µs/call # once (12µs+0s) by DBIx::Class::Storage::DBI::mysql::_run_connection_actions at line 94 of DBIx/Class/Storage/DBI/mysql.pm # once (5µs+0s) by DBIx::Class::Storage::DBI::_connect at line 1531 of DBIx/Class/Storage/DBI.pm # once (4µs+0s) by Try::Tiny::try at line 1492 of DBIx/Class/Storage/DBI.pm
sub DBI::common::FETCH; # xsub
# spent 41µs within DBI::common::STORE which was called 11 times, avg 4µs/call: # 3 times (7µs+0s) by DBI::connect at line 723 of DBI.pm, avg 2µs/call # 3 times (5µs+0s) by DBI::connect at line 726 of DBI.pm, avg 2µs/call # once (11µs+0s) by DBD::mysql::dr::connect at line 155 of DBD/mysql.pm # once (7µs+0s) by Koha::Objects::find at line 91 of Koha/Database.pm # once (5µs+0s) by DBI::connect at line 742 of DBI.pm # once (4µs+0s) by Koha::Objects::find at line 95 of Koha/Database.pm # once (2µs+0s) by DBIx::Class::Storage::DBI::mysql::_run_connection_actions at line 94 of DBIx/Class/Storage/DBI/mysql.pm
sub DBI::common::STORE; # xsub
# spent 8µs within DBI::common::func which was called 2 times, avg 4µs/call: # once (5µs+0s) by DBD::mysql::db::prepare at line 232 of DBD/mysql.pm # once (4µs+0s) by DBD::mysql::db::get_info at line 759 of DBD/mysql.pm
sub DBI::common::func; # xsub
# spent 141µs within DBI::db::do which was called: # once (141µs+0s) by DBIx::Class::Storage::DBI::__ANON__[/usr/share/perl5/DBIx/Class/Storage/DBI.pm:1440] at line 1438 of DBIx/Class/Storage/DBI.pm
sub DBI::db::do; # xsub
# spent 1.71ms (18µs+1.69) within DBI::db::get_info which was called: # once (18µs+1.69ms) by DBIx::Class::Storage::DBI::_dbh_get_info at line 1170 of DBIx/Class/Storage/DBI.pm
sub DBI::db::get_info; # xsub
# spent 90.2ms (29.8+60.4) within DBI::db::prepare_cached which was called 3000 times, avg 30µs/call: # 3000 times (29.8ms+60.4ms) by DBIx::Class::Storage::DBI::_prepare_sth at line 1846 of DBIx/Class/Storage/DBI.pm, avg 30µs/call
sub DBI::db::prepare_cached; # xsub
# spent 11.4ms within DBI::st::bind_param which was called 3000 times, avg 4µs/call: # 3000 times (11.4ms+0s) by DBIx::Class::Storage::DBI::_bind_sth_params at line 1887 of DBIx/Class/Storage/DBI.pm, avg 4µs/call
sub DBI::st::bind_param; # xsub
# spent 313ms within DBI::st::execute which was called 3000 times, avg 104µs/call: # 3000 times (313ms+0s) by DBIx::Class::Storage::DBI::_dbh_execute at line 1832 of DBIx/Class/Storage/DBI.pm, avg 104µs/call
sub DBI::st::execute; # xsub
# spent 29.1ms within DBI::st::fetchrow_array which was called 6000 times, avg 5µs/call: # 3000 times (17.7ms+0s) by DBIx::Class::Storage::DBI::select_single at line 2565 of DBIx/Class/Storage/DBI.pm, avg 6µs/call # 3000 times (11.4ms+0s) by DBIx::Class::Storage::DBI::select_single at line 2566 of DBIx/Class/Storage/DBI.pm, avg 4µs/call
sub DBI::st::fetchrow_array; # xsub
# spent 5.00ms within DBI::st::finish which was called 3000 times, avg 2µs/call: # 3000 times (5.00ms+0s) by DBIx::Class::Storage::DBI::select_single at line 2571 of DBIx/Class/Storage/DBI.pm, avg 2µs/call
sub DBI::st::finish; # xsub
# spent 721µs within File::Spec::Unix::canonpath which was called 891 times, avg 810ns/call: # 517 times (432µs+0s) by File::Spec::Unix::abs2rel at line 266 of File/Spec/Unix.pm, avg 835ns/call # 174 times (154µs+0s) by File::Spec::Unix::abs2rel at line 225 of File/Spec/Unix.pm, avg 885ns/call # 174 times (122µs+0s) by File::Spec::Unix::abs2rel at line 226 of File/Spec/Unix.pm, avg 700ns/call # 24 times (11µs+0s) by File::Spec::Unix::catdir at line 190 of Module/Find.pm, avg 475ns/call # 2 times (2µs+0s) by File::Spec::Unix::catdir at line 183 of Module/Find.pm, avg 1µs/call
sub File::Spec::Unix::canonpath; # xsub
# spent 89µs (75+14) within File::Spec::Unix::catdir which was called 26 times, avg 3µs/call: # 24 times (56µs+11µs) by Module::Find::_find at line 190 of Module/Find.pm, avg 3µs/call # 2 times (19µs+2µs) by Module::Find::_find at line 183 of Module/Find.pm, avg 11µs/call
sub File::Spec::Unix::catdir; # xsub
# spent 1µs within Internals::SvREADONLY which was called: # once (1µs+0s) by Class::C3::Componentised::ensure_class_loaded at line 1848 of DBIx/Class/ResultSource.pm
sub Internals::SvREADONLY; # xsub
# spent 318ms (281+37.1) within List::Util::first which was called 24167 times, avg 13µs/call: # 6151 times (63.0ms+0s) by DBIx::Class::ResultSource::columns_info at line 472 of DBIx/Class/ResultSource.pm, avg 10µs/call # 3000 times (106ms+19.7ms) by SQL::Abstract::_where_unary_op at line 637 of SQL/Abstract.pm, avg 42µs/call # 3000 times (81.1ms+17.4ms) by SQL::Abstract::_where_hashpair_HASHREF at line 887 of SQL/Abstract.pm, avg 33µs/call # 3000 times (9.49ms+0s) by DBIx::Class::Storage::DBI::_gen_sql_bind at line 1655 of DBIx/Class/Storage/DBI.pm, avg 3µs/call # 3000 times (7.85ms+0s) by DBIx::Class::ResultSet::_resolved_attrs at line 3524 of DBIx/Class/ResultSet.pm, avg 3µs/call # 3000 times (7.56ms+0s) by DBIx::Class::ResultSet::search_rs at line 491 of DBIx/Class/ResultSet.pm, avg 3µs/call # 3000 times (5.85ms+0s) by DBIx::Class::ResultSet::search_rs at line 467 of DBIx/Class/ResultSet.pm, avg 2µs/call # 16 times (40µs+0s) by DBIx::Class::Componentised::inject_base at line 203 of Class/C3/Componentised.pm, avg 3µs/call
sub List::Util::first; # xsub
# spent 255µs within Package::Stash::XS::name which was called 110 times, avg 2µs/call: # 28 times (25µs+0s) by Package::Stash::XS::namespace at line 381 of namespace/clean.pm, avg 889ns/call # 28 times (14µs+0s) by Package::Stash::XS::namespace at line 363 of namespace/clean.pm, avg 504ns/call # 27 times (203µs+0s) by Package::Stash::XS::namespace at line 213 of namespace/clean.pm, avg 8µs/call # 27 times (13µs+0s) by Package::Stash::XS::namespace at line 308 of namespace/clean.pm, avg 481ns/call
sub Package::Stash::XS::name; # xsub
# spent 2.10ms (1.85+255µs) within Package::Stash::XS::namespace which was called 1473 times, avg 1µs/call: # 680 times (459µs+0s) by B::Hooks::EndOfScope::XS::__ANON__ at line 237 of namespace/clean.pm, avg 676ns/call # 198 times (288µs+25µs) by namespace::clean::import at line 381 of namespace/clean.pm, avg 2µs/call # 170 times (722µs+203µs) by B::Hooks::EndOfScope::XS::__ANON__ at line 213 of namespace/clean.pm, avg 5µs/call # 170 times (155µs+13µs) by namespace::clean::import at line 308 of namespace/clean.pm, avg 986ns/call # 170 times (83µs+0s) by B::Hooks::EndOfScope::XS::__ANON__ at line 241 of namespace/clean.pm, avg 488ns/call # 56 times (126µs+14µs) by namespace::clean::import at line 363 of namespace/clean.pm, avg 3µs/call # 28 times (16µs+0s) by namespace::clean::import at line 365 of namespace/clean.pm, avg 554ns/call # once (500ns+0s) by B::Hooks::EndOfScope::XS::__ANON__ at line 250 of namespace/clean.pm
sub Package::Stash::XS::namespace; # xsub
# spent 15.6ms within Scalar::Util::blessed which was called 24527 times, avg 637ns/call: # 12000 times (6.45ms+0s) by SQL::Abstract::_refkind at line 1474 of SQL/Abstract.pm, avg 538ns/call # 6349 times (4.18ms+0s) by DBIx::Class::AccessorGroup::get_component_class at line 16 of DBIx/Class/AccessorGroup.pm, avg 658ns/call # 3000 times (2.95ms+0s) by DBIx::Class::Storage::DBIHacks::_resolve_ident_sources at line 683 of DBIx/Class/Storage/DBIHacks.pm, avg 984ns/call # 3000 times (1.71ms+0s) by DBIx::Class::Storage::DBI::_gen_sql_bind at line 1643 of DBIx/Class/Storage/DBI.pm, avg 570ns/call # 174 times (334µs+0s) by DBIx::Class::ResultSourceProxy::Table::table at line 84 of DBIx/Class/ResultSourceProxy/Table.pm, avg 2µs/call # 4 times (3µs+0s) by Method::Generate::Accessor::_generate_call_code at line 459 of Method/Generate/Accessor.pm, avg 850ns/call
sub Scalar::Util::blessed; # xsub
# spent 1µs within Scalar::Util::refaddr which was called: # once (1µs+0s) by DBIx::Class::Storage::DBI::_arm_global_destructor at line 221 of DBIx/Class/Storage/DBI.pm
sub Scalar::Util::refaddr; # xsub
# spent 26.6ms within Scalar::Util::reftype which was called 27709 times, avg 962ns/call: # 18527 times (16.4ms+0s) by Class::Accessor::Grouped::get_inherited at line 364 of Class/Accessor/Grouped.pm, avg 885ns/call # 3001 times (4.04ms+0s) by DBIx::Class::Storage::BlockRunner::new at line 82 of (eval 1105)[Sub/Quote.pm:5], avg 1µs/call # 3001 times (2.55ms+0s) by DBIx::Class::Storage::BlockRunner::run at line 112 of DBIx/Class/Storage/BlockRunner.pm, avg 851ns/call # 3000 times (3.54ms+0s) by DBIx::Class::Storage::DBI::_select_args at line 2498 of DBIx/Class/Storage/DBI.pm, avg 1µs/call # 180 times (114µs+0s) by Class::Accessor::Grouped::set_inherited at line 410 of Class/Accessor/Grouped.pm, avg 635ns/call
sub Scalar::Util::reftype; # xsub
# spent 9.59ms within Scalar::Util::weaken which was called 9177 times, avg 1µs/call: # 3001 times (4.68ms+0s) by DBIx::Class::Storage::BlockRunner::_run at line 132 of DBIx/Class/Storage/BlockRunner.pm, avg 2µs/call # 3001 times (2.58ms+0s) by DBIx::Class::Storage::BlockRunner::_run at line 133 of DBIx/Class/Storage/BlockRunner.pm, avg 859ns/call # 541 times (531µs+0s) by Sub::Defer::defer_sub at line 59 of Sub/Defer.pm, avg 981ns/call # 541 times (259µs+0s) by Sub::Defer::defer_sub at line 60 of Sub/Defer.pm, avg 478ns/call # 539 times (482µs+0s) by Sub::Quote::quote_sub at line 102 of Sub/Quote.pm, avg 894ns/call # 539 times (291µs+0s) by Sub::Quote::quote_sub at line 103 of Sub/Quote.pm, avg 541ns/call # 539 times (258µs+0s) by Sub::Quote::quote_sub at line 104 of Sub/Quote.pm, avg 480ns/call # 176 times (167µs+0s) by DBIx::Class::AccessorGroup::get_component_class at line 25 of DBIx/Class/AccessorGroup.pm, avg 948ns/call # 174 times (159µs+0s) by DBIx::Class::Schema::_register_source at line 1357 of DBIx/Class/Schema.pm, avg 913ns/call # 84 times (132µs+0s) by Class::Accessor::Grouped::__ANON__[/usr/share/perl5/Class/Accessor/Grouped.pm:942] at line 916 of Class/Accessor/Grouped.pm, avg 2µs/call # 26 times (30µs+0s) by DBIx::Class::ResultSet::_result_class or DBIx::Class::ResultSet::result_source or DBIx::Class::ResultSource::_columns or DBIx::Class::ResultSource::_ordered_columns or DBIx::Class::ResultSource::_primaries or DBIx::Class::ResultSource::_relationships or DBIx::Class::ResultSource::_unique_constraints or DBIx::Class::ResultSource::name or DBIx::Class::ResultSource::source_name or DBIx::Class::Row::in_storage or DBIx::Class::Storage::DBI::_conn_pid or DBIx::Class::Storage::DBI::_connect_info or DBIx::Class::Storage::DBI::_dbh or DBIx::Class::Storage::DBI::_dbh_autocommit or DBIx::Class::Storage::DBI::_dbh_details or DBIx::Class::Storage::DBI::_dbic_connect_attributes or DBIx::Class::Storage::DBI::_driver_determined or DBIx::Class::Storage::DBI::_sql_maker or DBIx::Class::Storage::DBI::_sql_maker_opts or DBIx::Class::Storage::DBI::disable_sth_caching or DBIx::Class::Storage::DBI::on_connect_call or DBIx::Class::Storage::DBI::on_connect_do or DBIx::Class::Storage::DBI::unsafe or DBIx::Class::Storage::debug or DBIx::Class::Storage::schema or DBIx::Class::Storage::transaction_depth at line 906 of Class/Accessor/Grouped.pm, avg 1µs/call # 14 times (24µs+0s) by Sub::Quote::unquote_sub at line 163 of Sub/Quote.pm, avg 2µs/call # once (1µs+0s) by DBIx::Class::Storage::set_schema at line 75 of DBIx/Class/Storage.pm # once (800ns+0s) by DBIx::Class::Storage::DBI::_arm_global_destructor at line 221 of DBIx/Class/Storage/DBI.pm
sub Scalar::Util::weaken; # xsub
# spent 22.3ms within Sub::Name::subname which was called 4573 times, avg 5µs/call: # 3938 times (18.7ms+0s) by Class::Accessor::Grouped::_mk_group_accessors at line 151 of Class/Accessor/Grouped.pm, avg 5µs/call # 547 times (3.28ms+0s) by Moo::_Utils::_name_coderef at line 96 of Moo/_Utils.pm, avg 6µs/call # 26 times (91µs+0s) by DBIx::Class::ResultSet::_result_class or DBIx::Class::ResultSet::result_source or DBIx::Class::ResultSource::_columns or DBIx::Class::ResultSource::_ordered_columns or DBIx::Class::ResultSource::_primaries or DBIx::Class::ResultSource::_relationships or DBIx::Class::ResultSource::_unique_constraints or DBIx::Class::ResultSource::name or DBIx::Class::ResultSource::source_name or DBIx::Class::Row::in_storage or DBIx::Class::Storage::DBI::_conn_pid or DBIx::Class::Storage::DBI::_connect_info or DBIx::Class::Storage::DBI::_dbh or DBIx::Class::Storage::DBI::_dbh_autocommit or DBIx::Class::Storage::DBI::_dbh_details or DBIx::Class::Storage::DBI::_dbic_connect_attributes or DBIx::Class::Storage::DBI::_driver_determined or DBIx::Class::Storage::DBI::_sql_maker or DBIx::Class::Storage::DBI::_sql_maker_opts or DBIx::Class::Storage::DBI::disable_sth_caching or DBIx::Class::Storage::DBI::on_connect_call or DBIx::Class::Storage::DBI::on_connect_do or DBIx::Class::Storage::DBI::unsafe or DBIx::Class::Storage::debug or DBIx::Class::Storage::schema or DBIx::Class::Storage::transaction_depth at line 898 of Class/Accessor/Grouped.pm, avg 4µs/call # 12 times (57µs+0s) by DBIx::Class::Relationship::ManyToMany::many_to_many at line 70 of DBIx/Class/Relationship/ManyToMany.pm, avg 5µs/call # 12 times (57µs+0s) by DBIx::Class::Relationship::ManyToMany::many_to_many at line 78 of DBIx/Class/Relationship/ManyToMany.pm, avg 5µs/call # 12 times (50µs+0s) by DBIx::Class::Relationship::ManyToMany::many_to_many at line 109 of DBIx/Class/Relationship/ManyToMany.pm, avg 4µs/call # 12 times (45µs+0s) by DBIx::Class::Relationship::ManyToMany::many_to_many at line 146 of DBIx/Class/Relationship/ManyToMany.pm, avg 4µs/call # 12 times (39µs+0s) by DBIx::Class::Relationship::ManyToMany::many_to_many at line 127 of DBIx/Class/Relationship/ManyToMany.pm, avg 3µs/call # once (6µs+0s) by DBIx::Class::SQLMaker::BEGIN@67 at line 75 of DBIx/Class/SQLMaker.pm # once (3µs+0s) by DBIx::Class::SQLMaker::BEGIN@67 at line 80 of DBIx/Class/SQLMaker.pm
sub Sub::Name::subname; # xsub
# spent 49.0ms within UNIVERSAL::can which was called 11463 times, avg 4µs/call: # 6000 times (9.05ms+0s) by SQL::Abstract::_METHOD_FOR_refkind at line 1501 of SQL/Abstract.pm, avg 2µs/call # 3000 times (26.8ms+0s) by DBIx::Class::ResultSet::_construct_results at line 1369 of DBIx/Class/ResultSet.pm, avg 9µs/call # 1921 times (9.11ms+0s) by Class::Accessor::Grouped::_mk_group_accessors at line 91 of Class/Accessor/Grouped.pm, avg 5µs/call # 174 times (2.58ms+0s) by DBIx::Class::ResultSourceProxy::Table::table at line 91 of DBIx/Class/ResultSourceProxy/Table.pm, avg 15µs/call # 174 times (352µs+0s) by DBIx::Class::ResultSourceProxy::Table::table at line 99 of DBIx/Class/ResultSourceProxy/Table.pm, avg 2µs/call # 52 times (169µs+0s) by DBIx::Class::ResultSet::_result_class or DBIx::Class::ResultSet::result_source or DBIx::Class::ResultSource::_columns or DBIx::Class::ResultSource::_ordered_columns or DBIx::Class::ResultSource::_primaries or DBIx::Class::ResultSource::_relationships or DBIx::Class::ResultSource::_unique_constraints or DBIx::Class::ResultSource::name or DBIx::Class::ResultSource::source_name or DBIx::Class::Row::in_storage or DBIx::Class::Storage::DBI::_conn_pid or DBIx::Class::Storage::DBI::_connect_info or DBIx::Class::Storage::DBI::_dbh or DBIx::Class::Storage::DBI::_dbh_autocommit or DBIx::Class::Storage::DBI::_dbh_details or DBIx::Class::Storage::DBI::_dbic_connect_attributes or DBIx::Class::Storage::DBI::_driver_determined or DBIx::Class::Storage::DBI::_sql_maker or DBIx::Class::Storage::DBI::_sql_maker_opts or DBIx::Class::Storage::DBI::disable_sth_caching or DBIx::Class::Storage::DBI::on_connect_call or DBIx::Class::Storage::DBI::on_connect_do or DBIx::Class::Storage::DBI::unsafe or DBIx::Class::Storage::debug or DBIx::Class::Storage::schema or DBIx::Class::Storage::transaction_depth at line 815 of Class/Accessor/Grouped.pm, avg 3µs/call # 48 times (681µs+0s) by DBIx::Class::Relationship::ManyToMany::many_to_many at line 39 of DBIx/Class/Relationship/ManyToMany.pm, avg 14µs/call # 26 times (41µs+0s) by DBIx::Class::ResultSet::_result_class or DBIx::Class::ResultSet::result_source or DBIx::Class::ResultSource::_columns or DBIx::Class::ResultSource::_ordered_columns or DBIx::Class::ResultSource::_primaries or DBIx::Class::ResultSource::_relationships or DBIx::Class::ResultSource::_unique_constraints or DBIx::Class::ResultSource::name or DBIx::Class::ResultSource::source_name or DBIx::Class::Row::in_storage or DBIx::Class::Storage::DBI::_conn_pid or DBIx::Class::Storage::DBI::_connect_info or DBIx::Class::Storage::DBI::_dbh or DBIx::Class::Storage::DBI::_dbh_autocommit or DBIx::Class::Storage::DBI::_dbh_details or DBIx::Class::Storage::DBI::_dbic_connect_attributes or DBIx::Class::Storage::DBI::_driver_determined or DBIx::Class::Storage::DBI::_sql_maker or DBIx::Class::Storage::DBI::_sql_maker_opts or DBIx::Class::Storage::DBI::disable_sth_caching or DBIx::Class::Storage::DBI::on_connect_call or DBIx::Class::Storage::DBI::on_connect_do or DBIx::Class::Storage::DBI::unsafe or DBIx::Class::Storage::debug or DBIx::Class::Storage::schema or DBIx::Class::Storage::transaction_depth at line 860 of Class/Accessor/Grouped.pm, avg 2µs/call # 26 times (39µs+0s) by DBIx::Class::ResultSet::_result_class or DBIx::Class::ResultSet::result_source or DBIx::Class::ResultSource::_columns or DBIx::Class::ResultSource::_ordered_columns or DBIx::Class::ResultSource::_primaries or DBIx::Class::ResultSource::_relationships or DBIx::Class::ResultSource::_unique_constraints or DBIx::Class::ResultSource::name or DBIx::Class::ResultSource::source_name or DBIx::Class::Row::in_storage or DBIx::Class::Storage::DBI::_conn_pid or DBIx::Class::Storage::DBI::_connect_info or DBIx::Class::Storage::DBI::_dbh or DBIx::Class::Storage::DBI::_dbh_autocommit or DBIx::Class::Storage::DBI::_dbh_details or DBIx::Class::Storage::DBI::_dbic_connect_attributes or DBIx::Class::Storage::DBI::_driver_determined or DBIx::Class::Storage::DBI::_sql_maker or DBIx::Class::Storage::DBI::_sql_maker_opts or DBIx::Class::Storage::DBI::disable_sth_caching or DBIx::Class::Storage::DBI::on_connect_call or DBIx::Class::Storage::DBI::on_connect_do or DBIx::Class::Storage::DBI::unsafe or DBIx::Class::Storage::debug or DBIx::Class::Storage::schema or DBIx::Class::Storage::transaction_depth at line 837 of Class/Accessor/Grouped.pm, avg 2µs/call # 18 times (51µs+0s) by Class::C3::Componentised::ensure_class_loaded at line 119 of DBIx/Class/Storage/DBI.pm, avg 3µs/call # 4 times (12µs+0s) by Method::Generate::Accessor::_generate_xs at line 646 of Method/Generate/Accessor.pm, avg 3µs/call # 2 times (11µs+0s) by Method::Generate::Constructor::generate_method at line 103 of Method/Generate/Constructor.pm, avg 5µs/call # 2 times (8µs+0s) by Method::Generate::Constructor::generate_method at line 94 of Method/Generate/Constructor.pm, avg 4µs/call # 2 times (7µs+0s) by Method::Generate::Constructor::generate_method at line 109 of Method/Generate/Constructor.pm, avg 3µs/call # 2 times (5µs+0s) by Moo::_constructor_maker_for at line 155 of Moo.pm, avg 3µs/call # 2 times (4µs+0s) by Method::Generate::Constructor::new or Moo::Object::new at line 13 of Moo/Object.pm, avg 2µs/call # 2 times (4µs+0s) by Method::Generate::Constructor::new or Moo::Object::new at line 22 of Moo/Object.pm, avg 2µs/call # 2 times (3µs+0s) by Moo::_accessor_maker_for at line 128 of Moo.pm, avg 1µs/call # 2 times (2µs+0s) by Moo::_constructor_maker_for at line 157 of Moo.pm, avg 1µs/call # once (7µs+0s) by DBIx::Class::Storage::DBI::_determine_driver at line 1288 of DBIx/Class/Storage/DBI.pm # once (4µs+0s) by Moo::__ANON__[/usr/share/perl5/Moo.pm:183] at line 179 of Moo.pm # once (2µs+0s) by base::import at line 780 of Class/Accessor/Grouped.pm # once (1µs+0s) by base::import at line 781 of Class/Accessor/Grouped.pm
sub UNIVERSAL::can; # xsub
# spent 26.5ms within UNIVERSAL::isa which was called 6057 times, avg 4µs/call: # 6000 times (26.3ms+0s) by DBIx::Class::ResultSet::new at line 310 of DBIx/Class/ResultSet.pm, avg 4µs/call # 32 times (52µs+0s) by DBIx::Class::Componentised::inject_base at line 38 of DBIx/Class/Componentised.pm, avg 2µs/call # 16 times (41µs+0s) by DBIx::Class::Componentised::inject_base at line 201 of Class/C3/Componentised.pm, avg 3µs/call # 8 times (19µs+0s) by DBIx::Class::Componentised::inject_base at line 20 of DBIx/Class/Componentised.pm, avg 2µs/call # once (3µs+0s) by CGI::header at line 17 of (eval 1114)[CGI.pm:932]
sub UNIVERSAL::isa; # xsub
# spent 10.6ms within mro::get_linear_isa which was called 3369 times, avg 3µs/call: # 3179 times (10.3ms+0s) by Class::Accessor::Grouped::get_super_paths at line 507 of Class/Accessor/Grouped.pm, avg 3µs/call # 174 times (292µs+0s) by DBIx::Class::Schema::CORE:sort at line 244 of DBIx/Class/Schema.pm, avg 2µs/call # 16 times (28µs+0s) by DBIx::Class::Componentised::inject_base at line 203 of Class/C3/Componentised.pm, avg 2µs/call
sub mro::get_linear_isa; # xsub
# spent 14µs within mro::set_mro which was called 5 times, avg 3µs/call: # 4 times (10µs+0s) by DBIx::Class::Componentised::inject_base at line 194 of Class/C3/Componentised.pm, avg 3µs/call # once (4µs+0s) by DBIx::Class::Storage::DBI::_determine_driver at line 1265 of DBIx/Class/Storage/DBI.pm
sub mro::set_mro; # xsub
# spent 117µs within version::vxs::_VERSION which was called 14 times, avg 8µs/call: # 10 times (95µs+0s) by strictures::VERSION at line 22 of strictures.pm, avg 9µs/call # once (8µs+0s) by Class::Accessor::Grouped::BEGIN@31 at line 50 of Class/Accessor/Grouped.pm # once (6µs+0s) by Method::Generate::Accessor::BEGIN@11 at line 17 of Method/Generate/Accessor.pm # once (4µs+0s) by Class::Accessor::Grouped::BEGIN@31 at line 51 of Class/Accessor/Grouped.pm # once (4µs+0s) by Method::Generate::Accessor::BEGIN@11 at line 21 of Method/Generate/Accessor.pm
sub version::vxs::_VERSION; # xsub